[INFO] fetching crate mahler 0.25.10...
[INFO] testing mahler-0.25.10 against try#0abe69e6c16114311cc2700e972492e64d69c28f for pr-154065-1
[INFO] extracting crate mahler 0.25.10 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate mahler 0.25.10
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate mahler 0.25.10
[INFO] tweaked toml for crates.io crate mahler 0.25.10 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate mahler 0.25.10 on toolchain 0abe69e6c16114311cc2700e972492e64d69c28f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate mahler 0.25.10 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f59cc5c022870faf994113cdc0f29376ef82147be12958bcc4c9cd67ffb0676a
[INFO] running `Command { std: "docker" "start" "-a" "f59cc5c022870faf994113cdc0f29376ef82147be12958bcc4c9cd67ffb0676a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f59cc5c022870faf994113cdc0f29376ef82147be12958bcc4c9cd67ffb0676a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f59cc5c022870faf994113cdc0f29376ef82147be12958bcc4c9cd67ffb0676a", kill_on_drop: false }`
[INFO] [stdout] f59cc5c022870faf994113cdc0f29376ef82147be12958bcc4c9cd67ffb0676a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 54d18f1f32609d5954756a0ee933f2ca7d500a2d82095c73711b7172779ad8a0
[INFO] running `Command { std: "docker" "start" "-a" "54d18f1f32609d5954756a0ee933f2ca7d500a2d82095c73711b7172779ad8a0", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling futures-io v0.3.32
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling matchit v0.8.6
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling futures-macro v0.3.32
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling mahler-derive v0.25.10
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling tokio-stream v0.1.18
[INFO] [stderr]    Compiling jsonptr v0.7.1
[INFO] [stderr]    Compiling json-patch v4.1.0
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling mahler-core v0.25.10
[INFO] [stderr]    Compiling mahler v0.25.10 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 19.73s
[INFO] running `Command { std: "docker" "inspect" "54d18f1f32609d5954756a0ee933f2ca7d500a2d82095c73711b7172779ad8a0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "54d18f1f32609d5954756a0ee933f2ca7d500a2d82095c73711b7172779ad8a0", kill_on_drop: false }`
[INFO] [stdout] 54d18f1f32609d5954756a0ee933f2ca7d500a2d82095c73711b7172779ad8a0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 1149be9fa974d87ed3b6f0da9675ab6e4f9ac583dce0a1db5c5b18960ddb10b5
[INFO] running `Command { std: "docker" "start" "-a" "1149be9fa974d87ed3b6f0da9675ab6e4f9ac583dce0a1db5c5b18960ddb10b5", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling mahler-core v0.25.10
[INFO] [stderr]    Compiling tokio-stream v0.1.18
[INFO] [stderr]    Compiling tokio-test v0.4.5
[INFO] [stderr]    Compiling mahler v0.25.10 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 31.20s
[INFO] running `Command { std: "docker" "inspect" "1149be9fa974d87ed3b6f0da9675ab6e4f9ac583dce0a1db5c5b18960ddb10b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1149be9fa974d87ed3b6f0da9675ab6e4f9ac583dce0a1db5c5b18960ddb10b5", kill_on_drop: false }`
[INFO] [stdout] 1149be9fa974d87ed3b6f0da9675ab6e4f9ac583dce0a1db5c5b18960ddb10b5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 383ea4a7d4883539073ccdd9a4f7e3b14b37c2097347786c9fff61bb8cd42215
[INFO] running `Command { std: "docker" "start" "-a" "383ea4a7d4883539073ccdd9a4f7e3b14b37c2097347786c9fff61bb8cd42215", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/mahler-c607cf983df6a127)
[INFO] [stdout] 
[INFO] [stdout] running 137 tests
[INFO] [stdout] test extract::args::de::tests::test_parse_error_error ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_map ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_error_at_index_error ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_tuple_string_parse ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_tuple_unsupported_key_type ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_tuple_string_string ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_error_at_key_error ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_wrong_tuple_length ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_struct ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_struct_ignoring_additional_fields ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_tuple_ignoring_additional_fields ... ok
[INFO] [stdout] test extract::args::de::tests::test_unsupported_type_error_nested_data_structure ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq ... ok
[INFO] [stdout] test extract::args::de::tests::test_wrong_number_of_parameters_error ... ok
[INFO] [stdout] test extract::args::tests::deserializes_hashmap_args ... ok
[INFO] [stdout] test extract::args::tests::deserializes_simple_path_args ... ok
[INFO] [stdout] test extract::view::tests::it_allows_changing_a_value_with_a_view ... ok
[INFO] [stdout] test extract::view::tests::it_extracts_an_existing_value_using_optional_view ... ok
[INFO] [stdout] test extract::view::tests::it_fails_if_optional_view_path_is_invalid ... ok
[INFO] [stdout] test extract::view::tests::it_commit_is_noop_with_detached_channel ... ok
[INFO] [stdout] test extract::view::tests::it_deletes_a_value_from_the_end_of_a_vec_with_optional_view ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_single_value ... ok
[INFO] [stdout] test extract::view::tests::it_assigns_a_value_to_optional_view_path ... ok
[INFO] [stdout] test extract::view::tests::it_creates_a_value_on_a_vec_with_optional_view ... ok
[INFO] [stdout] test extract::view::tests::it_fails_to_initialize_view_if_path_does_not_exist ... ok
[INFO] [stdout] test extract::view::tests::it_flush_is_noop_with_detached_channel ... ok
[INFO] [stdout] test extract::view::tests::it_initializes_optional_view_with_create ... ok
[INFO] [stdout] test sensor::tests::test_registry_find_matching_exact_path ... ok
[INFO] [stdout] test sensor::tests::test_registry_find_matching_longer_path_no_match ... ok
[INFO] [stdout] test sensor::tests::test_registry_find_matching_multiple_parameters ... ok
[INFO] [stdout] test sensor::tests::test_registry_find_matching_no_match ... ok
[INFO] [stdout] test sensor::tests::test_registry_find_matching_partial_path_no_match ... ok
[INFO] [stdout] test sensor::tests::test_registry_find_matching_with_parameter ... ok
[INFO] [stdout] test sensor::tests::test_registry_root_path ... ok
[INFO] [stdout] test task::effect::tests::it_allows_errors_in_sync_executions ... ok
[INFO] [stdout] test task::effect::tests::it_allows_sync_executions ... ok
[INFO] [stdout] test task::effect::tests::it_allows_wrapping_a_value ... ok
[INFO] [stdout] test extract::view::tests::it_deletes_a_value_on_a_vec_with_optional_view ... ok
[INFO] [stdout] test extract::args::tests::deserializes_tuple_args ... ok
[INFO] [stdout] test task::tests::identical_closures_have_different_ids ... ok
[INFO] [stdout] test extract::view::tests::it_initializes_optional_view_with_default ... ok
[INFO] [stdout] test sensor::tests::test_registry_empty_returns_none ... ok
[INFO] [stdout] test task::tests::it_allows_to_describe_a_task ... ok
[INFO] [stdout] test task::tests::it_allows_to_dry_run_actions_returning_error ... ok
[INFO] [stdout] test task::tests::it_allows_to_dry_run_async_actions ... ok
[INFO] [stdout] test task::tests::it_catches_condition_failure_in_methods_returning_option ... ok
[INFO] [stdout] test task::tests::it_allows_to_dry_run_pure_actions ... ok
[INFO] [stdout] test task::tests::it_gets_metadata_from_function ... ok
[INFO] [stdout] test task::tests::it_identifies_task_scoping_based_on_args ... ok
[INFO] [stdout] test task::tests::it_modifies_system_sub_elements ... ok
[INFO] [stdout] test task::tests::it_runs_async_actions ... ok
[INFO] [stdout] test worker::domain::tests::it_constructs_a_path_given_arguments ... ok
[INFO] [stdout] test extract::view::tests::it_commits_view_changes_with_attached_channel ... ok
[INFO] [stdout] test extract::view::tests::it_commits_optional_view_creation ... ok
[INFO] [stdout] test worker::domain::tests::test_args_sorted_by_route_order ... ok
[INFO] [stdout] test worker::domain::tests::test_finds_jobs_for_empty_paths ... ignored
[INFO] [stdout] test worker::domain::tests::test_error_if_unmatched_placeholders_remain ... ok
[INFO] [stdout] test worker::domain::tests::test_escaped_parameters_remain ... ok
[INFO] [stdout] test worker::domain::tests::test_no_replacement_if_job_not_found ... ok
[INFO] [stdout] test worker::domain::tests::test_mixed_placeholders ... ok
[INFO] [stdout] test worker::domain::tests::test_wildcard_parameter_replacement ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_longest_common_prefix_common_prefix ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_longest_common_prefix_no_common_prefix ... ok
[INFO] [stdout] test worker::planner::distance::tests::it_calculates_possible_changes_to_target ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_longest_common_prefix_root_paths ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_longest_common_prefix_single_path ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_select_non_conflicting_prefer_prefixes_basic ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_select_non_conflicting_prefer_prefixes_prefix_first ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_select_non_conflicting_prefer_prefixes_no_later_prefix ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_longest_common_prefix_empty ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_select_non_conflicting_prefer_prefixes_with_conflicts ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_select_non_conflicting_prefer_prefixes_root_path ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_select_non_conflicting_prefer_prefixes_your_example ... ok
[INFO] [stdout] test worker::planner::path_utils::tests::test_select_non_conflicting_proper_path_prefix_vs_string_prefix ... ok
[INFO] [stdout] test extract::view::tests::it_flushes_view_changes_with_attached_channel ... ok
[INFO] [stdout] test extract::view::tests::it_flushes_optional_view_creation ... ok
[INFO] [stdout] test worker::planner::tests::it_calculates_a_linear_workflow ... ok
[INFO] [stdout] test task::effect::tests::it_allows_async_executions ... ok
[INFO] [stdout] test worker::planner::tests::it_calculates_a_linear_workflow_on_a_complex_state ... ok
[INFO] [stdout] test task::effect::tests::it_propagates_errors_in_async_calls ... ok
[INFO] [stdout] test worker::planner::tests::it_avoids_conflict_in_tasks_returned_from_methods ... ok
[INFO] [stdout] test worker::planner::tests::it_calculates_a_linear_workflow_on_a_complex_state_with_deep_compound_tasks ... ok
[INFO] [stdout] test worker::planner::tests::it_calculates_a_linear_workflow_on_a_complex_state_with_compound_tasks ... ok
[INFO] [stdout] test extract::view::tests::it_flushes_optional_view_deletion ... ok
[INFO] [stdout] test worker::planner::tests::it_avoids_conflicts_from_methods ... ok
[INFO] [stdout] test task::tests::it_allows_actions_returning_runtime_errors ... ok
[INFO] [stdout] test task::tests::it_allows_extending_actions_with_effect ... ok
[INFO] [stdout] test worker::planner::tests::test_array_element_conflicts ... ok
[INFO] [stdout] test worker::planner::tests::test_exception_only_applies_to_matching_operation ... ok
[INFO] [stdout] test worker::planner::tests::it_allows_empty_tasks_as_part_of_methods ... ok
[INFO] [stdout] test worker::planner::tests::test_exception_description_populates_reason ... ok
[INFO] [stdout] test worker::planner::tests::test_exception_preserves_parent_path_skip ... ok
[INFO] [stdout] test worker::planner::tests::test_exception_skips_path_with_simple_condition ... ok
[INFO] [stdout] test worker::planner::tests::it_ignores_none_jobs ... ok
[INFO] [stdout] test worker::planner::tests::test_multiple_exceptions_on_different_paths ... ok
[INFO] [stdout] test worker::planner::tests::test_multiple_skipped_services_each_get_correct_reason ... ok
[INFO] [stdout] test worker::planner::tests::it_finds_concurrent_plans_with_nested_forks ... ok
[INFO] [stdout] test worker::planner::tests::test_exception_with_system_extractor ... ok
[INFO] [stdout] test worker::planner::tests::test_exception_with_target_extractor ... ok
[INFO] [stdout] test worker::planner::tests::test_exception_without_description_has_no_reason ... ok
[INFO] [stdout] test worker::tests::test_cleanup_hook_appended_to_workflow ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_error_at_key_error_multiple ... ok
[INFO] [stdout] test extract::args::de::tests::test_parse_seq_seq ... ok
[INFO] [stdout] test extract::view::tests::it_deletes_an_existing_value_with_optional_view ... ok
[INFO] [stdout] test worker::planner::tests::it_calculates_a_linear_workflow_with_compound_tasks ... ok
[INFO] [stdout] test worker::planner::tests::test_exception_last_iteration ... ok
[INFO] [stdout] test worker::tests::test_cleanup_hook_skipped_when_condition_not_met ... ok
[INFO] [stdout] test worker::tests::test_cleanup_hook_executed ... ok
[INFO] [stdout] test extract::view::tests::it_extracts_an_existing_value_on_a_vec_with_optional_view ... ok
[INFO] [stdout] test worker::planner::tests::it_calculates_concurrent_workflows_from_non_conflicting_paths ... ok
[INFO] [stdout] test worker::tests::test_no_cleanup_hook_when_no_plan_found ... ok
[INFO] [stdout] test worker::tests::test_cleanup_hook_skipped_when_workflow_is_empty ... ok
[INFO] [stdout] test worker::tests::test_no_cleanup_hook_when_not_configured ... ok
[INFO] [stdout] test worker::planner::tests::test_stacking_problem ... ok
[INFO] [stdout] test worker::tests::it_allows_running_atomic_tasks ... ok
[INFO] [stdout] test worker::tests::it_allows_testing_compound_tasks ... ok
[INFO] [stdout]   [2m2026-03-30T04:19:58.924504Z[0m [31mERROR[0m  [1;31merror[0m[31m: failure after commit then flush[0m
[INFO] [stdout]     [2;3mat[0m src/worker/workflow.rs:95
[INFO] [stdout] 
[INFO] [stdout] test worker::workflow::tests::test_commit_flush_failure_rolls_back_to_checkpoint ... ok
[INFO] [stdout] test worker::tests::test_stream_with_no_sensors_closes_on_completion ... ok
[INFO] [stdout]   [2m2026-03-30T04:19:58.925267Z[0m [31mERROR[0m  [1;31merror[0m[31m: create failed after flush[0m
[INFO] [stdout]     [2;3mat[0m src/worker/workflow.rs:95
[INFO] [stdout] 
[INFO] [stdout] test worker::workflow::tests::test_create_flush_then_failure_removes_path ... ok
[INFO] [stdout]   [2m2026-03-30T04:19:58.925490Z[0m [31mERROR[0m  [1;31merror[0m[31m: delete failed after flush[0m
[INFO] [stdout]     [2;3mat[0m src/worker/workflow.rs:95
[INFO] [stdout] 
[INFO] [stdout]   [2m2026-03-30T04:19:58.925853Z[0m [31mERROR[0m  [1;31merror[0m[31m: failure after flush[0m
[INFO] [stdout]     [2;3mat[0m src/worker/workflow.rs:95
[INFO] [stdout] 
[INFO] [stdout] test worker::planner::tests::it_aborts_search_if_plan_length_grows_too_much ... ok
[INFO] [stdout] test worker::workflow::tests::test_delete_flush_then_failure_recreates_path ... ok
[INFO] [stdout] test worker::workflow::tests::test_flush_then_failure_rolls_back_to_initial ... ok
[INFO] [stdout] test extract::view::tests::it_flushes_multiple_times ... ok
[INFO] [stdout]   [2m2026-03-30T04:19:58.932604Z[0m [31mERROR[0m  [1;31merror[0m[31m: internal error[0m
[INFO] [stdout]     [2;3mat[0m src/worker/workflow.rs:95
[INFO] [stdout] 
[INFO] [stdout]   [2m2026-03-30T04:19:58.932704Z[0m [31mERROR[0m  [1;31merror[0m[31m: failure after list modification[0m
[INFO] [stdout]     [2;3mat[0m src/worker/workflow.rs:95
[INFO] [stdout] 
[INFO] [stdout] test worker::workflow::tests::test_no_send_failure_no_rollback ... ok
[INFO] [stdout] test worker::workflow::tests::test_list_flush_then_failure_restores_original ... ok
[INFO] [stdout] test worker::tests::test_stream_yields_state_updates ... ok
[INFO] [stdout]   [2m2026-03-30T04:19:58.935193Z[0m [31mERROR[0m  [1;31merror[0m[31m: simulated error[0m
[INFO] [stdout]     [2;3mat[0m src/worker/workflow.rs:95
[INFO] [stdout] 
[INFO] [stdout] test worker::tests::test_flushed_changes_rollback_on_error ... ok
[INFO] [stdout] test worker::tests::test_worker_complex_state ... ok
[INFO] [stdout] test worker::tests::test_worker_find_and_run_separately ... ok
[INFO] [stdout] test worker::domain::tests::it_fails_if_assigning_the_same_job_to_multiple_routes - should panic ... ok
[INFO] [stdout] test worker::tests::test_view_flush_propagates_to_stream ... ok
[INFO] [stdout] test worker::tests::test_worker_workflow_mismatch_should_fail - should panic ... ok
[INFO] [stdout] test worker::tests::test_worker_bug ... ok
[INFO] [stdout] test worker::tests::test_sensor_initial_subscription_for_existing_state ... ok
[INFO] [stdout] test worker::tests::test_sensor_updates_during_workflow ... ok
[INFO] [stdout] test worker::tests::test_stream_continues_reporting_sensors_cleanup_hookcomplete ... ok
[INFO] [stdout] test worker::tests::test_workflow_interrupted_when_stream_dropped ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 136 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.33s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/state_derive.rs (/opt/rustwide/target/debug/deps/state_derive-0fe153af08a2b0e0)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test test_basic_serialization ... ok
[INFO] [stdout] test test_enum_model_support ... ok
[INFO] [stdout] test test_collection_default_deserialization ... ok
[INFO] [stdout] test test_mahler_default_attribute ... ok
[INFO] [stdout] test test_mahler_derive_attribute ... ok
[INFO] [stdout] test test_mahler_internal_and_default_combined ... ok
[INFO] [stdout] test test_map_model_support ... ok
[INFO] [stdout] test test_named_fields ... ok
[INFO] [stdout] test test_nested_collections_model_support ... ok
[INFO] [stdout] test test_enum_serialization_compatibility ... ok
[INFO] [stdout] test test_nested_model_types ... ok
[INFO] [stdout] test test_newtype_struct ... ok
[INFO] [stdout] test test_option_default_deserialization ... ok
[INFO] [stdout] test test_option_skip_none_serialization ... ok
[INFO] [stdout] test test_required_field_still_errors_when_missing ... ok
[INFO] [stdout] test test_serialization_compatibility ... ok
[INFO] [stdout] test test_primitive_types_remain_unchanged ... ok
[INFO] [stdout] test test_unit_struct ... ok
[INFO] [stdout] test test_struct_with_enum_fields ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests mahler
[INFO] [stdout] 
[INFO] [stdout] running 55 tests
[INFO] [stdout] test src/extract/mod.rs - extract (line 9) - compile ... ok
[INFO] [stdout] test src/extract/system.rs - extract::system::System (line 14) - compile ... ok
[INFO] [stdout] test src/extract/path.rs - extract::path::Path (line 10) - compile ... ok
[INFO] [stdout] test src/extract/mod.rs - extract (line 95) - compile ... ok
[INFO] [stdout] test src/extract/res.rs - extract::res::Res (line 17) - compile ... ok
[INFO] [stdout] test src/extract/mod.rs - extract (line 32) ... ok
[INFO] [stdout] test src/extract/target.rs - extract::target::Target (line 15) - compile ... ok
[INFO] [stdout] test src/extract/target.rs - extract::target::RawTarget (line 148) - compile ... ok
[INFO] [stdout] test src/extract/view.rs - extract::view::View (line 74) - compile ... ok
[INFO] [stdout] test src/extract/target.rs - extract::target::Target (line 74) - compile ... ok
[INFO] [stdout] test src/extract/target.rs - extract::target::SystemTarget (line 218) - compile ... ok
[INFO] [stdout] test src/extract/res.rs - extract::res::Res (line 50) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 210) ... ok
[INFO] [stdout] test src/extract/args/mod.rs - extract::args::Args (line 20) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 253) ... ok
[INFO] [stdout] test src/extract/view.rs - extract::view::View<T>::flush (line 178) ... ok
[INFO] [stdout] test src/exception/mod.rs - exception (line 18) ... ok
[INFO] [stdout] test src/lib.rs - (line 169) ... ok
[INFO] [stdout] test src/lib.rs - (line 31) ... ok
[INFO] [stdout] test src/lib.rs - (line 317) ... ok
[INFO] [stdout] test src/lib.rs - (line 286) ... ok
[INFO] [stdout] test src/lib.rs - (line 120) ... ok
[INFO] [stdout] test src/job.rs - job::Job::with_description (line 47) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO (line 36) ... ok
[INFO] [stdout] test src/lib.rs - (line 94) ... ok
[INFO] [stdout] test src/task/handler.rs - task::handler::Handler::with_arg (line 63) ... ok
[INFO] [stdout] test src/extract/target.rs - extract::target::Target (line 39) - compile ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO<T,E>::map_err (line 146) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO<T,E>::map (line 81) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::with_io (line 209) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::IO<T,E>::and_then (line 112) ... ok
[INFO] [stdout] test src/task/io.rs - task::io::enforce (line 255) ... ok
[INFO] [stdout] test src/sensor.rs - sensor (line 12) ... ok
[INFO] [stdout] test src/task/handler.rs - task::handler::Handler::with_target (line 46) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 275) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized>::sensor (line 402) - compile ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 153) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 173) ... ok
[INFO] [stdout] test src/task/mod.rs - task::Task::with_target (line 295) ... ok
[INFO] [stdout] test src/worker/planner/path_utils.rs - worker::planner::path_utils::longest_common_prefix (line 53) ... ignored
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized>::resource (line 595) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 252) ... ok
[INFO] [stdout] test src/lib.rs - (line 407) ... ok
[INFO] [stdout] test src/task/mod.rs - task::Task::with_arg (line 309) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized>::use_resource (line 643) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 325) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Ready>::seek_target (line 923) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized>::jobs (line 439) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized>::resource (line 572) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 299) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker (line 194) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized>::use_resource (line 620) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized>::with_cleanup (line 514) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Uninitialized>::with_cleanup (line 484) ... ok
[INFO] [stdout] test src/worker/mod.rs - worker::Worker<O,Ready>::run_task (line 1014) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 54 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 3.33s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "383ea4a7d4883539073ccdd9a4f7e3b14b37c2097347786c9fff61bb8cd42215", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "383ea4a7d4883539073ccdd9a4f7e3b14b37c2097347786c9fff61bb8cd42215", kill_on_drop: false }`
[INFO] [stdout] 383ea4a7d4883539073ccdd9a4f7e3b14b37c2097347786c9fff61bb8cd42215
